[INFO] fetching crate echo_execution 0.1.4...
[INFO] testing echo_execution-0.1.4 against try#28f95933dc0727396c07b48d0a8cb5211b7fb472 for pr-154065-2
[INFO] extracting crate echo_execution 0.1.4 into /workspace/builds/worker-2-tc2/source
[INFO] started tweaking crates.io crate echo_execution 0.1.4
[INFO] finished tweaking crates.io crate echo_execution 0.1.4
[INFO] tweaked toml for crates.io crate echo_execution 0.1.4 written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate echo_execution 0.1.4 on toolchain 28f95933dc0727396c07b48d0a8cb5211b7fb472
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate echo_execution 0.1.4 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5
[INFO] running `Command { std: "docker" "start" "edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-w" "/opt/rustwide/workdir" "--user" "0:0" "edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling shlex v1.3.0
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling icu_locale_core v2.1.1
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling indexmap v2.14.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling cc v1.2.61
[INFO] [stderr]    Compiling icu_collections v2.1.1
[INFO] [stderr]    Compiling tokio v1.52.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling openssl v0.10.78
[INFO] [stderr]    Compiling icu_provider v2.1.1
[INFO] [stderr]    Compiling openssl-macros v0.1.1
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling icu_properties v2.1.2
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling openssl-sys v0.9.114
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling native-tls v0.2.18
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling iri-string v0.7.12
[INFO] [stderr]    Compiling chrono v0.4.41
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling rustls-pki-types v1.14.1
[INFO] [stderr]    Compiling uuid v1.23.1
[INFO] [stderr]    Compiling rand v0.8.6
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling option-ext v0.2.0
[INFO] [stderr]    Compiling dirs-sys v0.5.0
[INFO] [stderr]    Compiling similar v2.7.0
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling dirs v6.0.0
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling h2 v0.4.13
[INFO] [stderr]    Compiling tokio-native-tls v0.3.1
[INFO] [stderr]    Compiling echo_core v0.1.4
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling hyper-tls v0.6.0
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling echo_tools v0.1.4
[INFO] [stderr]    Compiling echo_execution v0.1.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 27s
[INFO] running `Command { std: "docker" "inspect" "edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-w" "/opt/rustwide/workdir" "--user" "0:0" "edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling echo_execution v0.1.4 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 7.64s
[INFO] running `Command { std: "docker" "inspect" "edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-w" "/opt/rustwide/workdir" "--user" "0:0" "edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.24s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/echo_execution-9038423bbdfac4d1)
[INFO] [stdout] 
[INFO] [stdout] running 126 tests
[INFO] [stdout] test sandbox::docker::tests::test_docker_args_include_full_command ... ok
[INFO] [stdout] test sandbox::docker::tests::test_docker_args_security ... ok
[INFO] [stdout] test sandbox::docker::tests::test_docker_args_with_limits ... ok
[INFO] [stdout] test sandbox::docker::tests::test_docker_config_default ... ok
[INFO] [stdout] test sandbox::docker::tests::test_inner_command_code ... ok
[INFO] [stdout] test sandbox::docker::tests::test_inner_command_shell ... ok
[INFO] [stdout] test sandbox::docker::tests::test_select_image_python ... ok
[INFO] [stdout] test sandbox::k8s::tests::test_k8s_config_default ... ok
[INFO] [stdout] test sandbox::local::tests::test_linux_reports_process_isolation_without_os_sandbox_backend ... ok
[INFO] [stdout] test sandbox::k8s::tests::test_select_image_default ... ok
[INFO] [stdout] test sandbox::k8s::tests::test_inner_command_code ... ok
[INFO] [stdout] test sandbox::k8s::tests::test_inner_command_php ... ok
[INFO] [stdout] test sandbox::k8s::tests::test_inner_command_program ... ok
[INFO] [stdout] test sandbox::docker::tests::test_select_image_fallback ... ok
[INFO] [stdout] test sandbox::local::tests::test_local_sandbox_exit_code ... ok
[INFO] [stdout] test sandbox::local::tests::test_local_sandbox_echo ... ok
[INFO] [stdout] test sandbox::local::tests::test_local_sandbox_program ... ok
[INFO] [stdout] test sandbox::manager::tests::test_execute_simple_command ... ok
[INFO] [stdout] test sandbox::local::tests::test_local_sandbox_env ... ok
[INFO] [stdout] test sandbox::local::tests::test_local_sandbox_stdin ... ok
[INFO] [stdout] test sandbox::manager::tests::test_execute_with_policy ... ok
[INFO] [stdout] test sandbox::local::tests::test_local_sandbox_is_available ... ok
[INFO] [stdout] test sandbox::manager::tests::test_select_executor_container_fallback ... ok
[INFO] [stdout] test sandbox::manager::tests::test_select_executor_container_no_fallback ... ok
[INFO] [stdout] test sandbox::policy::tests::test_default_policy_code_execution ... ok
[INFO] [stdout] test sandbox::manager::tests::test_select_executor_none ... ok
[INFO] [stdout] test sandbox::manager::tests::test_local_only ... ok
[INFO] [stdout] test sandbox::policy::tests::test_script_interpreter_escalation ... ok
[INFO] [stdout] test sandbox::policy::tests::test_strict_policy ... ok
[INFO] [stdout] test sandbox::policy::tests::test_trusted_policy ... ok
[INFO] [stdout] test sandbox::policy::tests::test_trusted_policy_safe_command ... ok
[INFO] [stdout] test sandbox::tests::test_select_image_shell_binary_name_mapping ... ok
[INFO] [stdout] test sandbox::tests::test_select_image_shell_path_uses_default_image ... ok
[INFO] [stdout] test skills::external::loader::tests::test_allowed_tools_string ... ok
[INFO] [stdout] test skills::external::loader::tests::test_extract_instructions_legacy ... ok
[INFO] [stdout] test skills::external::loader::tests::test_parse_frontmatter_legacy ... ok
[INFO] [stdout] test skills::external::loader::tests::test_parse_frontmatter_missing_description ... ok
[INFO] [stdout] test skills::external::loader::tests::test_parse_frontmatter_no_frontmatter ... ok
[INFO] [stdout] test skills::external::loader::tests::test_parse_frontmatter_standard ... ok
[INFO] [stdout] test skills::external::loader::tests::test_parse_frontmatter_unclosed ... ok
[INFO] [stdout] test skills::external::loader::tests::test_scope_to_dirs_custom ... ok
[INFO] [stdout] test skills::external::loader::tests::test_scope_to_dirs_project ... ok
[INFO] [stdout] test skills::external::loader::tests::test_extract_instructions_body ... ok
[INFO] [stdout] test sandbox::policy::tests::test_default_policy_dangerous_command ... ok
[INFO] [stdout] test sandbox::manager::tests::test_execute_with_limits_uses_limit_aware_policy ... ok
[INFO] [stdout] test sandbox::policy::tests::test_default_policy_safe_command ... ok
[INFO] [stdout] test sandbox::policy::tests::test_command_substitution_detection ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_block_command_execution ... ok
[INFO] [stdout] test sandbox::manager::tests::test_execute_with_limits_falls_back_when_enabled ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_full_processing ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_injection_safe_argument_no_command ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_substitute_claude_compat_vars ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_mcp_source_skips_execution ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_substitute_variables ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_inline_command_does_not_inherit_unlisted_env ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_inline_command_execution ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_injection_via_arguments_block ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_substitute_no_args ... ok
[INFO] [stdout] test skills::external::run_script_tool::tests::test_invocation_no_longer_has_shell_prefix ... ok
[INFO] [stdout] test skills::external::run_script_tool::tests::test_resolve_interpreter_extensions ... ok
[INFO] [stdout] test skills::external::run_script_tool::tests::test_resolve_powershell ... ok
[INFO] [stdout] test skills::external::run_script_tool::tests::test_resolve_shell ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_variables_still_expand_in_text_regions ... ok
[INFO] [stdout] test skills::external::run_script_tool::tests::test_shlex_split_empty ... ok
[INFO] [stdout] test skills::external::run_script_tool::tests::test_shlex_split_malformed ... ok
[INFO] [stdout] test skills::external::run_script_tool::tests::test_shlex_split_quotes ... ok
[INFO] [stdout] test skills::external::run_script_tool::tests::test_shlex_split_simple ... ok
[INFO] [stdout] test skills::external::types::tests::test_allowed_tools_value_list ... ok
[INFO] [stdout] test skills::external::types::tests::test_allowed_tools_value_string ... ok
[INFO] [stdout] test skills::external::types::tests::test_descriptor_catalog_line ... ok
[INFO] [stdout] test skills::external::types::tests::test_descriptor_validate_name_invalid ... ok
[INFO] [stdout] test skills::external::types::tests::test_descriptor_validate_name_valid ... ok
[INFO] [stdout] test skills::external::resource_tool::tests::read_skill_resource_enforces_allowed_tools ... ok
[INFO] [stdout] test skills::external::types::tests::test_permits_tool ... ok
[INFO] [stdout] test skills::external::types::tests::test_raw_frontmatter_into_descriptor ... ok
[INFO] [stdout] test skills::external::run_script_tool::tests::test_resolve_typescript ... ok
[INFO] [stdout] test skills::external::types::tests::test_raw_frontmatter_legacy_detection ... ok
[INFO] [stdout] test skills::external::types::tests::test_resource_kind_display ... ok
[INFO] [stdout] test skills::external::types::tests::test_skill_content_prompt_block ... ok
[INFO] [stdout] test skills::external::types::tests::test_matches_context_path ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_injection_via_positional_arg ... ok
[INFO] [stdout] test skills::external::types::tests::test_validate_paths_overly_broad ... ok
[INFO] [stdout] test skills::external::types::tests::test_validate_paths_unbalanced_braces ... ok
[INFO] [stdout] test skills::external::types::tests::test_validate_paths_good ... ok
[INFO] [stdout] test skills::hooks::tests::test_hook_action_permission ... ok
[INFO] [stdout] test skills::external::run_script_tool::tests::run_skill_script_enforces_allowed_tools ... ok
[INFO] [stdout] test skills::external::types::tests::test_validate_paths_traversal ... ok
[INFO] [stdout] test skills::hooks::tests::test_hook_registry_empty ... ok
[INFO] [stdout] test skills::hooks::tests::test_hook_registry_no_match ... ok
[INFO] [stdout] test skills::hooks::tests::test_hook_registry_register ... ok
[INFO] [stdout] test skills::hooks::tests::test_hook_definition_deserialize ... ok
[INFO] [stdout] test skills::hooks::tests::test_hook_result_permission_decision ... ok
[INFO] [stdout] test skills::hooks::tests::test_matches_tool_exact ... ok
[INFO] [stdout] test skills::hooks::tests::test_matches_tool_glob ... ok
[INFO] [stdout] test skills::hooks::tests::test_hook_registry_runs_in_deterministic_skill_name_order ... ok
[INFO] [stdout] test skills::hooks::tests::test_matches_tool_prefix ... ok
[INFO] [stdout] test skills::hooks::tests::test_matches_tool_wildcard ... ok
[INFO] [stdout] test skills::hooks::tests::test_merge_result_permission_mode_override_last_wins ... ok
[INFO] [stdout] test skills::hooks::tests::test_merge_result_permission_priority_deny_wins ... ok
[INFO] [stdout] test skills::hooks::tests::test_parse_hook_output_allow ... ok
[INFO] [stdout] test skills::hooks::tests::test_parse_hook_output_block ... ok
[INFO] [stdout] test skills::hooks::tests::test_parse_hook_output_empty ... ok
[INFO] [stdout] test skills::hooks::tests::test_parse_hook_output_nonzero_exit ... ok
[INFO] [stdout] test skills::hooks::tests::test_parse_hook_output_permission_decision_allow ... ok
[INFO] [stdout] test skills::hooks::tests::test_parse_hook_output_permission_decision_ask ... ok
[INFO] [stdout] test skills::hooks::tests::test_parse_hook_output_permission_decision_deny ... ok
[INFO] [stdout] test skills::hooks::tests::test_parse_hook_output_permission_mode ... ok
[INFO] [stdout] test skills::hooks::tests::test_parse_hook_output_updated_input ... ok
[INFO] [stdout] test skills::hooks::tests::test_hook_command_receives_session_id ... ok
[INFO] [stdout] test skills::hooks::tests::test_hook_command_execution ... ok
[INFO] [stdout] test skills::registry::tests::test_activation_tracking ... ok
[INFO] [stdout] test skills::registry::tests::test_available_names ... ok
[INFO] [stdout] test skills::registry::tests::test_catalog_prompt ... ok
[INFO] [stdout] test skills::registry::tests::test_code_skills ... ok
[INFO] [stdout] test skills::external::prompt_exec::tests::test_injection_via_arguments_inline ... ok
[INFO] [stdout] test skills::registry::tests::test_extract_body ... ok
[INFO] [stdout] test skills::registry::tests::test_extract_body_malformed_frontmatter_returns_empty ... ok
[INFO] [stdout] test skills::registry::tests::test_extract_body_no_frontmatter ... ok
[INFO] [stdout] test skills::registry::tests::test_mixed_skills ... ok
[INFO] [stdout] test skills::registry::tests::test_register_descriptor ... ok
[INFO] [stdout] test skills::registry::tests::test_register_descriptor_with_legacy ... ok
[INFO] [stdout] test skills::registry::tests::test_registry_new ... ok
[INFO] [stdout] test sandbox::local::tests::test_local_sandbox_timeout ... ok
[INFO] [stdout] test skills::registry::tests::test_activate_falls_back_to_legacy_instructions ... ok
[INFO] [stdout] test skills::external::run_script_tool::tests::test_resolve_python ... ok
[INFO] [stdout] test skills::hooks::tests::test_hook_registry_prompt_match ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 126 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests echo_execution
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test src/skills/builtin/filesystem.rs - skills::builtin::filesystem::FileSystemSkill (line 32) ... ignored
[INFO] [stdout] test src/skills/builtin/shell.rs - skills::builtin::shell::ShellSkill (line 16) ... ignored
[INFO] [stdout] test src/sandbox/mod.rs - sandbox (line 21) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 2 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.76s; merged doctests compilation took 0.75s
[INFO] running `Command { std: "docker" "inspect" "edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5", kill_on_drop: false }`
[INFO] [stdout] edb00513ed53f0d4875b06f4307176d6de46a48fdd90c623f7c600f51bc767c5
